[2017.09.17 12:32:05] [0000m.00s.050ms] [1] FlowInstallVersion: WARNING: Could not get flow install version file [InstallVersion.txt] creation time
[2017.09.17 12:32:05] [0000m.00s.058ms] [0] WELCOME
[2017.09.17 12:32:05] [0000m.00s.058ms] [1] iCDB Server ver.1.094, vt40T8RtWJOG2cE2Nlvc5A, build:535943 created 2013-01-08 09:09:26, sources stamp:vt40T8RtWJOG2cE2Nlvc5A
[2017.09.17 12:32:05] [0000m.00s.058ms] [1] Copyright 2013 Mentor Graphics Corporation. All Rights Reserved.
[2017.09.17 12:32:05] [0000m.00s.058ms] [1] Base flow version [7.9.4]
[2017.09.17 12:32:05] [0000m.00s.058ms] [1] GBS Install Id [26418], Flow Id [EE7.9.4], Full Flow Name [Expedition Enterprise Flow], Exact Access Date [Jun 1 2012]
[2017.09.17 12:32:05] [0000m.00s.058ms] [1] UPDATE Install Info, GBS Install Id [29736], UPDATE Name [Expedition Enterprise Flow - Update 10 for EE7.9.4]
[2017.09.17 12:32:05] [0000m.00s.058ms] [1] Machine [W2130]  User [levk]  PID [1384]
[2017.09.17 12:32:05] [0000m.00s.058ms] [1] Operating system [Microsoft Windows 7 Business Edition, 64-bit Service Pack 1 (build 7601)]
[2017.09.17 12:32:05] [0000m.00s.058ms] [1] Current working dir [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB]
[2017.09.17 12:32:05] [0000m.00s.058ms] [1] PATH [C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\xe\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\wg\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\MGC_HOME.ixn\bin;C:\MentorGraphics\7.9.4EE\MGC_HOME.ixn\lib;C:\Program Files\Common Files\Microsoft Shared\Microsoft Online Services;C:\Program Files (x86)\Common Files\Microsoft Shared\Microsoft Online Services;C:\ProgramData\Oracle\Java\javapath;C:\MentorGraphics\Allegro Export ODB++\nv\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files (x86)\Common Files\Acronis\SnapAPI\;C:\Program Files (x86)\Altium Designer S09 Viewer\System;C:\Program Files\Aladdin\eToken\PKIClient\x32;C:\Program Files\Aladdin\eToken\PKIClient\x64;C:\Program Files\TortoiseSVN\bin;C:\Program Files (x86)\Skype\Phone\;C:\Cadence\SPB_16.6\openaccess\bin\win32\opt;C:\Cadence\SPB_16.6\tools\capture;C:\Cadence\SPB_16.6\tools\pspice;C:\Cadence\SPB_16.6\tools\specctra\bin;C:\Cadence\SPB_16.6\tools\fet\bin;C:\Cadence\SPB_16.6\tools\libutil\bin;C:\Cadence\SPB_16.6\tools\bin;C:\Cadence\SPB_16.6\tools\pcb\bin;;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.5EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\1.0CCASM\SDD_HOME\common\win32\lib]
[2017.09.17 12:32:05] [0000m.00s.058ms] [1] SDD_HOME [C:\MentorGraphics\7.9.4EE\SDD_HOME] on file system [NTFS]
[2017.09.17 12:32:05] [0000m.00s.058ms] [1] SDD_PLATFORM [win32]
[2017.09.17 12:32:05] [0000m.00s.058ms] [1] WDIR [C:\WDIR\7.9.4EE;C:\MentorGraphics\7.9.4EE\SDD_HOME\standard]
[2017.09.17 12:32:05] [0000m.00s.058ms] [1] Local iCDB working dir [C:\WDIR\7.9.4EE\iCDB] on file system [NTFS]
[2017.09.17 12:32:05] [0000m.00s.061ms] [1] S: Config file name [ServerWG.cfg]
[2017.09.17 12:32:05] [0000m.00s.061ms] [2] S: Loading config files
[2017.09.17 12:32:05] [0000m.00s.074ms] [1] Config file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\cdbsvr\ServerWG.cfg] not found
[2017.09.17 12:32:05] [0000m.00s.074ms] [1] Config: Non-default value [System.SingleUserMode] = [no]
[2017.09.17 12:32:05] [0000m.00s.076ms] [3] S: Config loaded
[2017.09.17 12:32:05] [0000m.00s.102ms] [0] S: Project path [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb] on file system [NTFS]
[2017.09.17 12:32:05] [0000m.00s.103ms] [0] S: Project global path [\\berg\data\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb] on file system [NTFS]
[2017.09.17 12:32:05] [0000m.00s.103ms] [0] S: Project file (.prj) path [\\berg\data\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\FC-MPCI-1R0.prj] on file system [UNKNOWN]
[2017.09.17 12:32:05] [0000m.00s.104ms] [0] S: Project file (.prj) local path [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\FC-MPCI-1R0.prj] on file system [NTFS]
[2017.09.17 12:32:05] [0000m.00s.111ms] [1] S: WARNING: Database auto-repair support DISABLED
[2017.09.17 12:32:05] [0000m.00s.111ms] [0] S: Multi-user mode (config)
[2017.09.17 12:32:05] [0000m.00s.111ms] [0] S: Event polling mode autodetection
[2017.09.17 12:32:05] [0000m.00s.119ms] [0] S: Started up automatically by iCDB Client: W2130:levk:1348
[2017.09.17 12:32:05] [0000m.00s.119ms] [0] S: Auto close mode
[2017.09.17 12:32:05] [0000m.00s.119ms] [0] S: Database ID [9158-59ed-635f-dd63-6b0f-fa9b-b0ff-a950-beae-8c0c-2cd9-86c5]
[2017.09.17 12:32:05] [0000m.00s.119ms] [0] PathTranslator: BEGIN dumping
[2017.09.17 12:32:05] [0000m.00s.119ms] [0] PathTranslator: Local -> global path prefix list
[2017.09.17 12:32:05] [0000m.00s.119ms] [0] PathTranslator: [V:\] -> [\\SQLSERVER\Software\]
[2017.09.17 12:32:05] [0000m.00s.119ms] [0] PathTranslator: [W:\] -> [\\SQLSERVER\AppDistribution\]
[2017.09.17 12:32:05] [0000m.00s.119ms] [0] PathTranslator: [X:\] -> [\\BERG\system\Ufolders\levk\]
[2017.09.17 12:32:05] [0000m.00s.119ms] [0] PathTranslator: [Y:\] -> [\\berg\programs\]
[2017.09.17 12:32:05] [0000m.00s.119ms] [0] PathTranslator: [Z:\] -> [\\berg\data\]
[2017.09.17 12:32:05] [0000m.00s.119ms] [0] PathTranslator: Global -> local path prefix list
[2017.09.17 12:32:05] [0000m.00s.120ms] [0] PathTranslator: [\\SQLSERVER\AppDistribution\] -> [W:\]
[2017.09.17 12:32:05] [0000m.00s.120ms] [0] PathTranslator: [\\BERG\system\Ufolders\levk\] -> [X:\]
[2017.09.17 12:32:05] [0000m.00s.120ms] [0] PathTranslator: [\\SQLSERVER\Software\] -> [V:\]
[2017.09.17 12:32:05] [0000m.00s.120ms] [0] PathTranslator: [\\berg\programs\] -> [Y:\]
[2017.09.17 12:32:05] [0000m.00s.120ms] [0] PathTranslator: [\\berg\data\] -> [Z:\]
[2017.09.17 12:32:05] [0000m.00s.120ms] [0] PathTranslator: END dumping
[2017.09.17 12:32:05] [0000m.00s.120ms] [0] S: START
[2017.09.17 12:32:05] [0000m.00s.122ms] [0] S: Server startup lock file created
[2017.09.17 12:32:05] [0000m.00s.124ms] [0] S: Listening for client connections on port [10000]
[2017.09.17 12:32:05] [0000m.00s.125ms] [0] S: Server network ID [W2130:192.168.10.119,W2130,W2130.VG.LOCAL:10000]
[2017.09.17 12:32:05] [0000m.00s.125ms] [0] S: Server GUID [76c7-7c50-9025-d9d1-5114-eb9a-114e-b950-7f6e-8c0c-2cd9-86c5]
[2017.09.17 12:32:05] [0000m.00s.125ms] [0] S: Project GUID [9158-59ed-635f-dd63-6b0f-fa9b-b0ff-a950-beae-8c0c-2cd9-86c5]
[2017.09.17 12:32:05] [0000m.00s.133ms] [0] S: Wrote data to server address file
[2017.09.17 12:32:05] [0000m.00s.135ms] [0] S: Server startup lock file removed
[2017.09.17 12:32:05] [0000m.00s.137ms] [1] ProjectBackupManager: Setting project file (.prj) path: [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\FC-MPCI-1R0.prj]
[2017.09.17 12:32:05] [0000m.00s.243ms] [0] S: Plugins loaded: [PIM] [ENetGen70] 
[2017.09.17 12:32:05] [0000m.00s.244ms] [0] S: Local disc access time: 43 us
[2017.09.17 12:32:05] [0000m.00s.244ms] [0] S: File server access time: 214 us
[2017.09.17 12:32:05] [0000m.00s.255ms] [0] Single file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\icdb.dat] size [73840 (72.1 kB)]
[2017.09.17 12:32:05] [0000m.00s.258ms] [1] Single file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\icdb.dat] opened in read-write mode
[2017.09.17 12:32:05] [0000m.00s.261ms] [0] SingleFile: [0000-0000-0000-0000] SFI header version check [1009] [1009]
[2017.09.17 12:32:05] [0000m.00s.261ms] [0] Database GUARD OK: [73840]
[2017.09.17 12:32:05] [0000m.00s.270ms] [0] SingleFile: [0000-0000-0000-0000] SFI header version check [1009] [1009]
[2017.09.17 12:32:05] [0000m.00s.270ms] [0] Database GUARD OK: [73840]
[2017.09.17 12:32:05] [0000m.00s.270ms] [0] SingleFile: [ab06-e095-5029-1e89] SFI version check [1009] [1009]
[2017.09.17 12:32:05] [0000m.00s.271ms] [0] SingleFile: [ab06-e095-5029-1e89] Database SFI header has been read in [1ms 747us]
[2017.09.17 12:32:05] [0000m.00s.278ms] [0] iCDB Server PI: Server & plugin session [139]
[2017.09.17 12:32:05] [0000m.00s.278ms] [0] iCDB Server PI: Opening database [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb]
[2017.09.17 12:32:05] [0000m.00s.278ms] [0] iCDB Server PI: Database [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb] opened
[2017.09.17 12:32:05] [0000m.00s.309ms] [0] iCDB Database: Checking existence of constraint definitions
[2017.09.17 12:32:05] [0000m.00s.309ms] [0] iCDB Database: Checking constraint definitions version
[2017.09.17 12:32:05] [0000m.00s.387ms] [1] iCDB Database: Constraint definitions version [ce_ee.cns=7.10.24;user.cns=bd5a-97c5-3627-126d-4128-4d69-1284-d650-358d-8c0c-2c99-86c5] up to date
[2017.09.17 12:32:05] [0000m.00s.412ms] [0] iCDB Server PI: Initializing plugins
[2017.09.17 12:32:05] [0000m.00s.415ms] [0] iCDB Server PI: Plugins initialized
[2017.09.17 12:32:05] [0000m.00s.415ms] [0] S: iCDB database loaded
[2017.09.17 12:32:05] [0000m.00s.417ms] [0] S: Starting threads
[2017.09.17 12:32:05] [0000m.00s.417ms] [0] BT: Listening for broadcast messages on port [10000]
[2017.09.17 12:32:05] [0000m.00s.419ms] [1] CMT: There are no clients - waiting [10 seconds]
[2017.09.17 12:32:05] [0000m.00s.425ms] [0] IT: Session [140] for host [W2130] user [levk] with process ID [1348] created
[2017.09.17 12:32:05] [0000m.00s.430ms] [1] SC[140]: Registered sub-client [Expedition PCB_10180796] with ID [1] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 12:32:05] [0000m.00s.482ms] [1] SC[140]: Registered sub-client [EEVM_EEVM_162061196] with ID [2] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 12:32:05] [0000m.00s.483ms] [1] SC[140]: UnRegistered sub-client [EEVM_EEVM_162061196] with ID [2] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 12:32:05] [0000m.00s.520ms] [1] CMT: There are [1] clients now: 140
[2017.09.17 12:32:08] [0000m.03s.271ms] [1] SC[140]: Registered sub-client [ExpeditionCrossProber] with ID [3] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 12:32:10] [0000m.05s.234ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_10186156] with ID [4] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 12:32:10] [0000m.05s.248ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_10186156] with ID [4] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 12:32:26] [0000m.21s.138ms] [0] DFAMT: File server access time: 274 us
[2017.09.17 12:45:52] [0013m.46s.984ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_11007953] with ID [5] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 12:45:52] [0013m.47s.024ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_11008000] with ID [6] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 12:45:52] [0013m.47s.030ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_11008000] with ID [6] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 12:46:31] [0014m.26s.766ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_11047734] with ID [7] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 12:46:31] [0014m.26s.777ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_11047734] with ID [7] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 12:46:52] [0014m.47s.173ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_11068140] with ID [8] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 12:46:52] [0014m.47s.184ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_11068140] with ID [8] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 12:46:52] [0014m.47s.583ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_11007953] with ID [5] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:16:11] [0044m.06s.506ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_12827500] with ID [9] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:16:29] [0044m.24s.617ms] [0] IT: Session [141] for host [W2130] user [levk] with process ID [8660] created
[2017.09.17 13:16:29] [0044m.24s.618ms] [1] SC[141]: Registered sub-client [JobPrefsDBPI_12845562] with ID [1] for session ID [141] host [W2130] user [levk] process ID [8660]
[2017.09.17 13:16:29] [0044m.24s.648ms] [1] CMT: There are [2] clients now: 140, 141
[2017.09.17 13:16:29] [0044m.24s.684ms] [1] SC[141]: UnRegistered sub-client [JobPrefsDBPI_12845562] with ID [1] for session ID [141] host [W2130] user [levk] process ID [8660]
[2017.09.17 13:16:29] [0044m.24s.749ms] [1] CMT: Removed client [141]
[2017.09.17 13:16:29] [0044m.24s.749ms] [1] CMT: There are [1] clients now: 140
[2017.09.17 13:16:31] [0044m.26s.151ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_12847140] with ID [10] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:16:31] [0044m.26s.178ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_12847140] with ID [10] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:16:31] [0044m.26s.278ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_12827500] with ID [9] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:16:35] [0044m.30s.571ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_12851562] with ID [11] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:16:37] [0044m.32s.077ms] [0] IT: Session [142] for host [W2130] user [levk] with process ID [7396] created
[2017.09.17 13:16:37] [0044m.32s.077ms] [1] SC[142]: Registered sub-client [JobPrefsDBPI_12853031] with ID [1] for session ID [142] host [W2130] user [levk] process ID [7396]
[2017.09.17 13:16:37] [0044m.32s.135ms] [1] CMT: There are [2] clients now: 140, 142
[2017.09.17 13:16:37] [0044m.32s.161ms] [1] SC[142]: Registered sub-client [JobPrefsDBPI_12853156] with ID [2] for session ID [142] host [W2130] user [levk] process ID [7396]
[2017.09.17 13:16:37] [0044m.32s.182ms] [1] SC[142]: UnRegistered sub-client [JobPrefsDBPI_12853031] with ID [1] for session ID [142] host [W2130] user [levk] process ID [7396]
[2017.09.17 13:16:38] [0044m.33s.631ms] [1] SC[142]: UnRegistered sub-client [JobPrefsDBPI_12853156] with ID [2] for session ID [142] host [W2130] user [levk] process ID [7396]
[2017.09.17 13:16:38] [0044m.33s.662ms] [1] CMT: Removed client [142]
[2017.09.17 13:16:38] [0044m.33s.662ms] [1] CMT: There are [1] clients now: 140
[2017.09.17 13:16:39] [0044m.34s.388ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_12851562] with ID [11] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:16:52] [0044m.47s.509ms] [0] IT: Session [143] for host [W2130] user [levk] with process ID [1812] created
[2017.09.17 13:16:52] [0044m.47s.510ms] [1] SC[143]: Registered sub-client [levk (BE CES)] with ID [1] for session ID [143] host [W2130] user [levk] process ID [1812]
[2017.09.17 13:16:52] [0044m.47s.552ms] [1] CMT: There are [2] clients now: 140, 143
[2017.09.17 13:18:44] [0046m.39s.566ms] [1] SC[143]: UnRegistered sub-client [levk (BE CES)] with ID [1] for session ID [143] host [W2130] user [levk] process ID [1812]
[2017.09.17 13:18:44] [0046m.39s.626ms] [1] CMT: Removed client [143]
[2017.09.17 13:18:44] [0046m.39s.626ms] [1] CMT: There are [1] clients now: 140
[2017.09.17 13:18:53] [0046m.48s.390ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_12989390] with ID [12] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:18:54] [0046m.49s.746ms] [0] IT: Session [144] for host [W2130] user [levk] with process ID [3352] created
[2017.09.17 13:18:54] [0046m.49s.747ms] [1] SC[144]: Registered sub-client [JobPrefsDBPI_12990703] with ID [1] for session ID [144] host [W2130] user [levk] process ID [3352]
[2017.09.17 13:18:54] [0046m.49s.824ms] [1] CMT: There are [2] clients now: 140, 144
[2017.09.17 13:18:54] [0046m.49s.825ms] [1] SC[144]: Registered sub-client [JobPrefsDBPI_12990828] with ID [2] for session ID [144] host [W2130] user [levk] process ID [3352]
[2017.09.17 13:18:55] [0046m.49s.844ms] [1] SC[144]: UnRegistered sub-client [JobPrefsDBPI_12990703] with ID [1] for session ID [144] host [W2130] user [levk] process ID [3352]
[2017.09.17 13:18:56] [0046m.51s.272ms] [1] SC[144]: UnRegistered sub-client [JobPrefsDBPI_12990828] with ID [2] for session ID [144] host [W2130] user [levk] process ID [3352]
[2017.09.17 13:18:56] [0046m.51s.296ms] [1] CMT: Removed client [144]
[2017.09.17 13:18:56] [0046m.51s.296ms] [1] CMT: There are [1] clients now: 140
[2017.09.17 13:18:57] [0046m.52s.073ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_12989390] with ID [12] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:21:33] [0049m.28s.521ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_13149531] with ID [13] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:21:33] [0049m.28s.594ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_13149609] with ID [14] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:21:33] [0049m.28s.804ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_13149531] with ID [13] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:21:33] [0049m.28s.817ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_13149609] with ID [14] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:21:52] [0049m.47s.132ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_13168140] with ID [15] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:21:52] [0049m.47s.204ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_13168218] with ID [16] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:21:52] [0049m.47s.377ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_13168140] with ID [15] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:21:52] [0049m.47s.390ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_13168218] with ID [16] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:24:28] [0052m.23s.631ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_13324656] with ID [17] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:24:28] [0052m.23s.642ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_13324656] with ID [17] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:24:28] [0052m.23s.838ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_13324859] with ID [18] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:24:29] [0052m.23s.866ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_13324859] with ID [18] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:24:30] [0052m.25s.181ms] [0] IT: Session [145] for host [W2130] user [levk] with process ID [1652] created
[2017.09.17 13:24:30] [0052m.25s.181ms] [1] SC[145]: Registered sub-client [JobPrefsDBPI_13326156] with ID [1] for session ID [145] host [W2130] user [levk] process ID [1652]
[2017.09.17 13:24:30] [0052m.25s.218ms] [1] SC[145]: UnRegistered sub-client [JobPrefsDBPI_13326156] with ID [1] for session ID [145] host [W2130] user [levk] process ID [1652]
[2017.09.17 13:24:30] [0052m.25s.229ms] [1] CMT: There are [2] clients now: 140, 145
[2017.09.17 13:24:30] [0052m.25s.229ms] [1] CMT: Removed client [145]
[2017.09.17 13:24:30] [0052m.25s.229ms] [1] CMT: There are [1] clients now: 140
[2017.09.17 13:24:30] [0052m.25s.784ms] [0] IT: Session [146] for host [W2130] user [levk] with process ID [1652] created
[2017.09.17 13:24:30] [0052m.25s.784ms] [1] SC[146]: Registered sub-client [JobPrefsDBPI_13326765] with ID [1] for session ID [146] host [W2130] user [levk] process ID [1652]
[2017.09.17 13:24:30] [0052m.25s.833ms] [1] CMT: There are [2] clients now: 140, 146
[2017.09.17 13:24:31] [0052m.25s.869ms] [1] SC[146]: Registered sub-client [JobPrefsDBPI_13326890] with ID [2] for session ID [146] host [W2130] user [levk] process ID [1652]
[2017.09.17 13:24:31] [0052m.26s.114ms] [1] SC[146]: UnRegistered sub-client [JobPrefsDBPI_13326890] with ID [2] for session ID [146] host [W2130] user [levk] process ID [1652]
[2017.09.17 13:24:31] [0052m.26s.117ms] [1] SC[146]: UnRegistered sub-client [JobPrefsDBPI_13326765] with ID [1] for session ID [146] host [W2130] user [levk] process ID [1652]
[2017.09.17 13:24:31] [0052m.26s.134ms] [1] CMT: Removed client [146]
[2017.09.17 13:24:31] [0052m.26s.134ms] [1] CMT: There are [1] clients now: 140
[2017.09.17 13:24:31] [0052m.26s.328ms] [1] SC[140]: Registered sub-client [JobPrefsDBPI_13327359] with ID [19] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:24:31] [0052m.26s.343ms] [1] SC[140]: UnRegistered sub-client [JobPrefsDBPI_13327359] with ID [19] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:26:47] [0054m.42s.562ms] [1] SC[140]: UnRegistered sub-client [ExpeditionCrossProber] with ID [3] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:26:47] [0054m.42s.715ms] [1] SC[140]: UnRegistered sub-client [Expedition PCB_10180796] with ID [1] for session ID [140] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:26:47] [0054m.42s.821ms] [1] CMT: Removed client [140]
[2017.09.17 13:26:47] [0054m.42s.821ms] [1] CMT: There are no clients left - waiting [1 seconds]
[2017.09.17 13:26:48] [0054m.43s.505ms] [0] IT: Session [147] for host [W2130] user [levk] with process ID [1348] created
[2017.09.17 13:26:48] [0054m.43s.506ms] [1] SC[147]: Registered sub-client [Expedition PCB_13464500] with ID [1] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:26:50] [0054m.45s.538ms] [1] SC[147]: Registered sub-client [ExpeditionCrossProber] with ID [2] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:26:50] [0054m.45s.646ms] [1] SC[147]: Registered sub-client [JobPrefsDBPI_13466687] with ID [3] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:26:50] [0054m.45s.657ms] [1] SC[147]: UnRegistered sub-client [JobPrefsDBPI_13466687] with ID [3] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:27:30] [0055m.25s.164ms] [1] SC[147]: Registered sub-client [JobPrefsDBPI_13506203] with ID [4] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:27:30] [0055m.25s.235ms] [1] SC[147]: Registered sub-client [JobPrefsDBPI_13506281] with ID [5] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:27:30] [0055m.25s.403ms] [1] SC[147]: UnRegistered sub-client [JobPrefsDBPI_13506203] with ID [4] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:27:30] [0055m.25s.415ms] [1] SC[147]: UnRegistered sub-client [JobPrefsDBPI_13506281] with ID [5] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:27:42] [0055m.37s.177ms] [1] SC[147]: Registered sub-client [JobPrefsDBPI_13518218] with ID [6] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:27:42] [0055m.37s.191ms] [1] SC[147]: UnRegistered sub-client [JobPrefsDBPI_13518218] with ID [6] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:27:42] [0055m.37s.372ms] [1] SC[147]: Registered sub-client [JobPrefsDBPI_13518406] with ID [7] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:27:42] [0055m.37s.399ms] [1] SC[147]: UnRegistered sub-client [JobPrefsDBPI_13518406] with ID [7] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:30:25] [0058m.20s.680ms] [1] SC[147]: Registered sub-client [JobPrefsDBPI_13681718] with ID [8] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:30:29] [0058m.24s.003ms] [0] IT: Session [148] for host [W2130] user [levk] with process ID [7500] created
[2017.09.17 13:30:29] [0058m.24s.012ms] [1] CMT: There are [2] clients now: 147, 148
[2017.09.17 13:30:29] [0058m.24s.018ms] [1] SC[148]: Registered sub-client [JobPrefsDBPI_13684984] with ID [1] for session ID [148] host [W2130] user [levk] process ID [7500]
[2017.09.17 13:30:29] [0058m.24s.115ms] [1] SC[148]: Registered sub-client [JobPrefsDBPI_13685171] with ID [2] for session ID [148] host [W2130] user [levk] process ID [7500]
[2017.09.17 13:30:29] [0058m.24s.143ms] [1] SC[148]: UnRegistered sub-client [JobPrefsDBPI_13684984] with ID [1] for session ID [148] host [W2130] user [levk] process ID [7500]
[2017.09.17 13:30:30] [0058m.25s.697ms] [1] SC[148]: UnRegistered sub-client [JobPrefsDBPI_13685171] with ID [2] for session ID [148] host [W2130] user [levk] process ID [7500]
[2017.09.17 13:30:30] [0058m.25s.746ms] [1] CMT: Removed client [148]
[2017.09.17 13:30:30] [0058m.25s.746ms] [1] CMT: There are [1] clients now: 147
[2017.09.17 13:30:31] [0058m.26s.314ms] [1] SC[147]: UnRegistered sub-client [JobPrefsDBPI_13681718] with ID [8] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:30:46] [0058m.41s.204ms] [1] SC[147]: Registered sub-client [JobPrefsDBPI_13702250] with ID [9] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:30:46] [0058m.41s.283ms] [1] SC[147]: Registered sub-client [JobPrefsDBPI_13702328] with ID [10] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:30:46] [0058m.41s.438ms] [1] SC[147]: UnRegistered sub-client [JobPrefsDBPI_13702250] with ID [9] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:30:46] [0058m.41s.451ms] [1] SC[147]: UnRegistered sub-client [JobPrefsDBPI_13702328] with ID [10] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:30:50] [0058m.44s.872ms] [1] SC[147]: Registered sub-client [JobPrefsDBPI_13705906] with ID [11] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:30:52] [0058m.47s.774ms] [0] IT: Session [149] for host [W2130] user [levk] with process ID [5436] created
[2017.09.17 13:30:52] [0058m.47s.775ms] [1] SC[149]: Registered sub-client [JobPrefsDBPI_13708781] with ID [1] for session ID [149] host [W2130] user [levk] process ID [5436]
[2017.09.17 13:30:52] [0058m.47s.832ms] [1] SC[149]: UnRegistered sub-client [JobPrefsDBPI_13708781] with ID [1] for session ID [149] host [W2130] user [levk] process ID [5436]
[2017.09.17 13:30:53] [0058m.47s.872ms] [1] CMT: There are [2] clients now: 147, 149
[2017.09.17 13:30:53] [0058m.47s.872ms] [1] CMT: Removed client [149]
[2017.09.17 13:30:53] [0058m.47s.872ms] [1] CMT: There are [1] clients now: 147
[2017.09.17 13:30:54] [0058m.49s.352ms] [1] SC[147]: Registered sub-client [JobPrefsDBPI_13710406] with ID [12] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:30:54] [0058m.49s.375ms] [1] SC[147]: UnRegistered sub-client [JobPrefsDBPI_13710406] with ID [12] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:30:54] [0058m.49s.454ms] [1] SC[147]: UnRegistered sub-client [JobPrefsDBPI_13705906] with ID [11] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:34:34] [0062m.29s.543ms] [1] SC[147]: Registered sub-client [JobPrefsDBPI_13930609] with ID [13] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:34:34] [0062m.29s.558ms] [1] SC[147]: UnRegistered sub-client [JobPrefsDBPI_13930609] with ID [13] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:34:34] [0062m.29s.612ms] [1] SC[147]: Registered sub-client [NetClassDBPI_13930671] with ID [14] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:34:34] [0062m.29s.727ms] [1] SC[147]: UnRegistered sub-client [NetClassDBPI_13930671] with ID [14] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:34:34] [0062m.29s.761ms] [1] SC[147]: Registered sub-client [JobPrefsDBPI_13930812] with ID [15] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:34:34] [0062m.29s.773ms] [1] SC[147]: UnRegistered sub-client [JobPrefsDBPI_13930812] with ID [15] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:34:37] [0062m.32s.509ms] [1] SC[147]: Registered sub-client [JobPrefsDBPI_13933578] with ID [16] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:34:37] [0062m.32s.693ms] [1] SC[147]: UnRegistered sub-client [JobPrefsDBPI_13933578] with ID [16] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:34:38] [0062m.33s.001ms] [0] IT: Session [150] for host [W2130] user [levk] with process ID [7700] created
[2017.09.17 13:34:38] [0062m.33s.005ms] [0] IT: Session [151] for host [W2130] user [levk] with process ID [5324] created
[2017.09.17 13:34:38] [0062m.33s.005ms] [1] SC[150]: Registered sub-client [JobPrefsDBPI_13934000] with ID [1] for session ID [150] host [W2130] user [levk] process ID [7700]
[2017.09.17 13:34:38] [0062m.33s.008ms] [1] SC[151]: Registered sub-client [JobPrefsDBPI_13934000] with ID [1] for session ID [151] host [W2130] user [levk] process ID [5324]
[2017.09.17 13:34:38] [0062m.33s.099ms] [1] CMT: There are [3] clients now: 147, 150, 151
[2017.09.17 13:34:38] [0062m.33s.100ms] [1] SC[151]: Registered sub-client [NetClassDBPI_13934140] with ID [2] for session ID [151] host [W2130] user [levk] process ID [5324]
[2017.09.17 13:34:38] [0062m.33s.102ms] [1] SC[150]: Registered sub-client [JobPrefsDBPI_13934171] with ID [2] for session ID [150] host [W2130] user [levk] process ID [7700]
[2017.09.17 13:34:38] [0062m.33s.188ms] [1] SC[150]: Registered sub-client [NetClassDBPI_13934234] with ID [3] for session ID [150] host [W2130] user [levk] process ID [7700]
[2017.09.17 13:34:38] [0062m.33s.266ms] [1] SC[151]: Registered sub-client [JobPrefsDBPI_13934328] with ID [3] for session ID [151] host [W2130] user [levk] process ID [5324]
[2017.09.17 13:34:38] [0062m.33s.297ms] [1] SC[150]: Registered sub-client [Expedition_13934359] with ID [4] for session ID [150] host [W2130] user [levk] process ID [7700]
[2017.09.17 13:34:38] [0062m.33s.299ms] [1] SC[150]: UnRegistered sub-client [Expedition_13934359] with ID [4] for session ID [150] host [W2130] user [levk] process ID [7700]
[2017.09.17 13:34:39] [0062m.33s.880ms] [1] SC[151]: UnRegistered sub-client [JobPrefsDBPI_13934328] with ID [3] for session ID [151] host [W2130] user [levk] process ID [5324]
[2017.09.17 13:34:39] [0062m.33s.882ms] [1] SC[151]: UnRegistered sub-client [JobPrefsDBPI_13934000] with ID [1] for session ID [151] host [W2130] user [levk] process ID [5324]
[2017.09.17 13:34:39] [0062m.33s.885ms] [1] SC[151]: UnRegistered sub-client [NetClassDBPI_13934140] with ID [2] for session ID [151] host [W2130] user [levk] process ID [5324]
[2017.09.17 13:34:39] [0062m.33s.950ms] [1] CMT: Removed client [151]
[2017.09.17 13:34:39] [0062m.33s.950ms] [1] CMT: There are [2] clients now: 147, 150
[2017.09.17 13:34:40] [0062m.35s.609ms] [1] SC[150]: UnRegistered sub-client [JobPrefsDBPI_13934171] with ID [2] for session ID [150] host [W2130] user [levk] process ID [7700]
[2017.09.17 13:34:40] [0062m.35s.610ms] [1] SC[150]: UnRegistered sub-client [JobPrefsDBPI_13934000] with ID [1] for session ID [150] host [W2130] user [levk] process ID [7700]
[2017.09.17 13:34:40] [0062m.35s.614ms] [1] SC[150]: UnRegistered sub-client [NetClassDBPI_13934234] with ID [3] for session ID [150] host [W2130] user [levk] process ID [7700]
[2017.09.17 13:34:40] [0062m.35s.660ms] [1] CMT: Removed client [150]
[2017.09.17 13:34:40] [0062m.35s.660ms] [1] CMT: There are [1] clients now: 147
[2017.09.17 13:35:22] [0063m.17s.192ms] [1] SC[147]: UnRegistered sub-client [ExpeditionCrossProber] with ID [2] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:35:22] [0063m.17s.477ms] [1] SC[147]: UnRegistered sub-client [Expedition PCB_13464500] with ID [1] for session ID [147] host [W2130] user [levk] process ID [1348]
[2017.09.17 13:35:22] [0063m.17s.559ms] [1] CMT: Removed client [147]
[2017.09.17 13:35:22] [0063m.17s.559ms] [1] CMT: There are no clients left - waiting [1 seconds]
[2017.09.17 13:35:24] [0063m.18s.866ms] [0] CMT: Starting server auto-close
[2017.09.17 13:35:24] [0063m.18s.869ms] [0] S: Closing server
[2017.09.17 13:35:24] [0063m.18s.978ms] [0] iCDB Server PI: Closing plugins
[2017.09.17 13:35:24] [0063m.18s.978ms] [0] iCDB Server PI: Plugins closed
[2017.09.17 13:35:24] [0063m.18s.978ms] [0] iCDB Server PI: Unloading database
[2017.09.17 13:35:24] [0063m.18s.980ms] [0] iCDB Server PI: Saving storage
[2017.09.17 13:35:24] [0063m.18s.980ms] [0] iCDB Server PI: Closing database
[2017.09.17 13:35:24] [0063m.18s.983ms] [1] iCDB Server PI: Stats: Database defragmentation: max[1] avg[1] holes[56%] data[88%]
[2017.09.17 13:35:24] [0063m.18s.984ms] [1] iCDB Server PI: Stats: Database file size [114896 (112.2 kB)]
[2017.09.17 13:35:24] [0063m.18s.986ms] [1] iCDB Server PI: WARNING: Database needs defragmentation!
[2017.09.17 13:35:24] [0063m.18s.992ms] [0] iCDB Server PI: Database closed
[2017.09.17 13:35:24] [0063m.18s.993ms] [0] iCDB Server PI: Defragmenting database
[2017.09.17 13:35:24] [0063m.18s.994ms] [0] iCDB Server PI: Creating database [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb] backup to file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\cdbback\2017-09-17 13.35.24-1384-d.zip]
[2017.09.17 13:35:24] [0063m.19s.233ms] [0] iCDB Server PI: Database backup: DONE
[2017.09.17 13:35:24] [0063m.19s.242ms] [0] SingleFile: [0000-0000-0000-0000] SFI header version check [1009] [1009]
[2017.09.17 13:35:24] [0063m.19s.242ms] [0] Database GUARD OK: [114896]
[2017.09.17 13:35:24] [0063m.19s.242ms] [0] SingleFile: [ab06-e095-5029-1e89] SFI version check [1009] [1009]
[2017.09.17 13:35:24] [0063m.19s.244ms] [0] SingleFile: [ab06-e095-5029-1e89] Database SFI header has been read in [2ms 659us]
[2017.09.17 13:35:24] [0063m.19s.265ms] [1] iCDB Server PI: Stats: Database file sizes: before[114896 (112.2 kB)], after[73840 (72.1 kB)], gain[41056 (40.1 kB)]
[2017.09.17 13:35:24] [0063m.19s.274ms] [0] Single file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\icdb.dat] closed with size [73840 (72.1 kB)]
[2017.09.17 13:35:24] [0063m.19s.277ms] [0] iCDB Server PI: Database defragmentation completed successfully
[2017.09.17 13:35:24] [0063m.19s.277ms] [1] BackupTransaction: Removing database backup [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-MPCI_Rev1.0\PCB\keyin.icdb\cdbback\2017-09-17 13.35.24-1384-d.zip]
[2017.09.17 13:35:24] [0063m.19s.280ms] [1] iCDB Server PI: Stats: Total iCDB execution time: [129157] ticks
[2017.09.17 13:35:24] [0063m.19s.280ms] [1] iCDB Server PI: Stats: Total iCDB execution time: [41764] microseconds
[2017.09.17 13:35:24] [0063m.19s.281ms] [1] iCDB Server PI: Stats: Total iCDB commands count: [1737]
[2017.09.17 13:35:24] [0063m.19s.281ms] [1] iCDB Server PI: Stats: Average iCDB speed:        [41590] commands per second
[2017.09.17 13:35:24] [0063m.19s.371ms] [0] S: STOP
